--------------------------------- Captured Out --------------------------------- ---- new test ---- 1771059326.410 sable_ircd uid=1001 1771059326.411 sable_services uid=1001 1771059326.412 sable_services #x1B[2m2026-02-14T08:55:26.411639Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.History"), address: "127.0.0.1:46549", fingerprint: "c79ba11227c4b089c002ab5f0a653862c1ae262f" } 1771059326.412 sable_services #x1B[2m2026-02-14T08:55:26.411929Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.History#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Could not connect to 127.0.0.1:46549. Last error: I/O error: Connection refused (os error 111) 1771059326.412 sable_services #x1B[2m2026-02-14T08:55:26.411989Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.History#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mI/O error: Connection refused (os error 111) 1771059326.414 sable_ircd #x1B[2m2026-02-14T08:55:26.414166Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434015428349952)), timestamp: 1771059326, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1771059326, name: ServerName("My.Little.Server"), ts: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock() 1771059326.414 sable_ircd #x1B[2m2026-02-14T08:55:26.414296Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059326.415 sable_ircd #x1B[2m2026-02-14T08:55:26.414544Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m#x1B[1mapply_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mevent#x1B[0m#x1B[2m=#x1B[0mEvent { id: EventId(Snowflake(810434015428349952)), timestamp: 1771059326, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1771059326, name: ServerName("My.Little.Server"), ts: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_server#x1B[0m#x1B[1m{#x1B[0m#x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mNewServer { server: ServerId(1) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Server 1771059326.415 sable_ircd #x1B[2m2026-02-14T08:55:26.414675Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434015428349952)), timestamp: 1771059326, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }) 1771059326.415 sable_ircd #x1B[2m2026-02-14T08:55:26.414724Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434015428349952)), timestamp: 1771059326, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] } 1771059326.415 sable_ircd #x1B[2m2026-02-14T08:55:26.414817Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434015428349952)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059326.415 sable_ircd #x1B[2m2026-02-14T08:55:26.414854Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434015428349952)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] } 1771059326.416 sable_ircd #x1B[2m2026-02-14T08:55:26.415545Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from reap_preclients_timer 1771059326.416 sable_ircd #x1B[2m2026-02-14T08:55:26.415904Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434015432544257)), timestamp: 1771059326, clock: EventClock(1 => 810434015428349952), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1771059326 }) }; event clock=EventClock(1 => 810434015428349952) my clock=EventClock(1 => 810434015428349952) 1771059326.416 sable_ircd #x1B[2m2026-02-14T08:55:26.415977Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059326.416 sable_ircd #x1B[2m2026-02-14T08:55:26.416209Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434015432544257)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059326.416 sable_ircd #x1B[2m2026-02-14T08:55:26.416246Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434015432544257)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] } 1771059326.506 sable_ircd #x1B[2m2026-02-14T08:55:26.505917Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 1), remote_addr: 127.0.0.1, tls_info: None } 1771059326.506 sable_ircd #x1B[2m2026-02-14T08:55:26.506082Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059326.506 sable_ircd #x1B[2m2026-02-14T08:55:26.506128Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1771059326.506 sable_ircd #x1B[2m2026-02-14T08:55:26.506193Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 1): :My.Little.Server NOTICE * :*** Looking up your hostname 1771059326.506 sable_ircd #x1B[2m2026-02-14T08:55:26.506317Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059326.506 sable_ircd #x1B[2m2026-02-14T08:55:26.506370Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"QUIT :chkport" 1771059326.507 sable_ircd #x1B[2m2026-02-14T08:55:26.506497Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 1): ERROR :Client quit 1771059326.507 sable_ircd #x1B[2m2026-02-14T08:55:26.506624Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mCloseConnection(ConnectionId(ListenerId(0), 1)) 1771059326.507 sable_ircd #x1B[2m2026-02-14T08:55:26.506663Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::connection_collection#x1B[0m#x1B[2m:#x1B[0m Removing connection ConnectionId(ListenerId(0), 1) 1771059326.507 sable_ircd #x1B[2m2026-02-14T08:55:26.507174Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m Connection error #x1B[3mconnection#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed 1771059326.507 sable_ircd #x1B[2m2026-02-14T08:55:26.507273Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059326.508 sable_ircd #x1B[2m2026-02-14T08:55:26.507482Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from auth_events 1771059326.518 sable_services #x1B[2m2026-02-14T08:55:26.517539Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.Server"), address: "127.0.0.1:50795", fingerprint: "405dc979477c8f0c6c994bd712b3eca2cf4eef48" } 1771059326.518 sable_services #x1B[2m2026-02-14T08:55:26.517796Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059326.522 sable_ircd #x1B[2m2026-02-14T08:55:26.522413Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::node#x1B[0m#x1B[2m:#x1B[0m Server got state export request; sending 1771059326.527 chkSvs: connects to server. 1771059326.527 chkSvs -> S: NICK chkSvs 1771059326.527 chkSvs -> S: USER chk chk chk chk 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.527598Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 2), remote_addr: 127.0.0.1, tls_info: None } 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.527691Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.527725Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.527802Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE * :*** Looking up your hostname 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.527912Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.527960Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"NICK chkSvs" 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.528135Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 354, tv_nsec: 594034557 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.528202Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from async_handlers 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.528239Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.528268Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"USER chk chk chk chk" 1771059326.528 sable_ircd #x1B[2m2026-02-14T08:55:26.528332Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 354, tv_nsec: 594034557 }, attach_user_id: OnceLock(), user: OnceLock(Username("chk")), extra_user_params: OnceLock(("chk", "chk")), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(Realname("chk")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059326.529 sable_ircd #x1B[2m2026-02-14T08:55:26.528881Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from auth_events 1771059326.529 sable_ircd #x1B[2m2026-02-14T08:55:26.528935Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m DNS lookup finished for ConnectionId(ListenerId(0), 2): 127.0.0.1/Some(Hostname("localhost")) 1771059326.529 sable_ircd #x1B[2m2026-02-14T08:55:26.528991Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059326.529 sable_ircd #x1B[2m2026-02-14T08:55:26.529035Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 354, tv_nsec: 594034557 }, attach_user_id: OnceLock(), user: OnceLock(Username("chk")), extra_user_params: OnceLock(("chk", "chk")), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(Realname("chk")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059326.529 sable_ircd #x1B[2m2026-02-14T08:55:26.529104Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mRegisterClient(ConnectionId(ListenerId(0), 2)) 1771059326.529 sable_ircd #x1B[2m2026-02-14T08:55:26.529370Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }; event clock=EventClock(1 => 810434015432544257) my clock=EventClock(1 => 810434015432544257) 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.529485Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.529785Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(810434015910694912)), 1) }), users_to_notify: [] }) 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.529829Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(810434015910694912)), 1) }), users_to_notify: [] } 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.529878Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(810434015910694912)), 2), connection: UserConnectionId(Snowflake(810434015910694913)) }), users_to_notify: [] }) 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.529902Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(810434015910694912)), 2), connection: UserConnectionId(Snowflake(810434015910694913)) }), users_to_notify: [] } 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.529947Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 001 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.529974Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 002 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.529997Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 003 chkSvs :This server was created 2026-02-14 08:55:26.529988738 UTC 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.530016Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 004 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.530049Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 005 chkSvs EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.530070Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 422 chkSvs :MOTD File is missing 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.530091Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :chkSvs!chk@localhost MODE chkSvs + 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.530107Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE chkSvs :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.530146Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059326.530 sable_ircd #x1B[2m2026-02-14T08:55:26.530167Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] } 1771059326.565 sable_services #x1B[2m2026-02-14T08:55:26.564443Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Bootstrap message: Message { source_server: (ServerId(1), 1771059326), content: NetworkState(Network { nick_bindings: {}, historic_nick_users: HistoricNickStore { data: {} }, users: {}, historic_users: HistoricUserStore { users: {} }, user_connections: {}, channels: {}, list_mode_entries: {}, channel_topics: {}, channel_invites: {}, memberships: {}, messages: {}, servers: {ServerId(1): Server { id: ServerId(1), epoch: 1771059326, name: ServerName("My.Little.Server"), last_ping: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }}, network_bans: BanRepository { pre_registration_bans: {}, new_connection_bans: {}, pre_sasl_bans: {}, pre_registration_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: ["", "", "", "", "", ""], uint64: [], regex: [] }, fields: {"user_param_1": (5, String(_)), "nick": (3, String(_)), "user": (2, String(_)), "tls": (0, Boolean(_)), "host": (4, String(_)), "realname": (1, String(_)), "ip": (0, Ip(_)), "user_param_2": (0, String(_))} }, new_connection_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: [], uint64: [], regex: [] }, fields: {"ip": (0, Ip(_)), "tls": (0, Boolean(_))} }, pre_sasl_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: [""], uint64: [], regex: [] }, fields: {"mechanism": (0, String(_)), "tls": (0, Boolean(_)), "ip": (0, Ip(_))} } }, audit_log: {}, accounts: {}, nick_registrations: {}, channel_registrations: {}, channel_accesses: {}, channel_roles: {}, current_services: None, current_history_server_id: None, config: NetworkConfig { opers: [OperConfig { name: "operuser", hash: "$6$z5yA.OfGliDoi/R2$BgSsguS6bxAsPSCygDisgDw5JZuo5.88eU3Hyc7/4OaNpeKIxWGjOggeHzOl0xLiZg1vfwxXjOTFN14wG5vNI." }], debug_mode: true, default_roles: {BuiltinVoice: ChannelAccessSet(4296081441), BuiltinAll: ChannelAccessSet(1114112), BuiltinOp: ChannelAccessSet(222192934775)}, alias_users: [AliasUser { nick: Nickname("ChanServ"), user: Username("ChanServ"), host: Hostname("services."), realname: Realname("Channel services compatibility layer"), command_alias: "CS" }, AliasUser { nick: Nickname("NickServ"), user: Username("NickServ"), host: Hostname("services."), realname: Realname("Account services compatibility layer"), command_alias: "NS" }], object_expiry: 60, pingout_duration: 600 }, clock: EventClock(1 => 810434015432544257), cache_default_channel_roles: OnceLock(), alias_users: OnceLock() }) } 1771059326.565 sable_services #x1B[2m2026-02-14T08:55:26.564613Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Server 1771059326.565 sable_services #x1B[2m2026-02-14T08:55:26.564733Z#x1B[0m #x1B[33m WARN#x1B[0m #x1B[2msable_services::database::jsonfile#x1B[0m#x1B[2m:#x1B[0m Couldn't open database file, starting from empty 1771059326.565 sable_services #x1B[2m2026-02-14T08:55:26.565225Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059326, name: ServerName("My.Little.Services"), ts: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 810434015432544257) my clock=EventClock(1 => 810434015432544257) 1771059326.566 sable_services #x1B[2m2026-02-14T08:55:26.565571Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059326, name: ServerName("My.Little.Services"), ts: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059326.567 sable_services #x1B[2m2026-02-14T08:55:26.567424Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434016070479873)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016062091264), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059326 }) }; event clock=EventClock(1 => 810434015432544257, 99 => 810434016062091264) my clock=EventClock(1 => 810434015432544257, 99 => 810434016062091264) 1771059326.568 sable_services #x1B[2m2026-02-14T08:55:26.568127Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016070479873)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016062091264), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059326 }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059326.572 sable_services #x1B[2m2026-02-14T08:55:26.571852Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m#x1B[1mapply_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mevent#x1B[0m#x1B[2m=#x1B[0mEvent { id: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059326, name: ServerName("My.Little.Services"), ts: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_server#x1B[0m#x1B[1m{#x1B[0m#x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mNewServer { server: ServerId(99) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Services 1771059326.572 sable_services #x1B[2m2026-02-14T08:55:26.572150Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434016091451394)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016070479873), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 810434015432544257, 99 => 810434016070479873) my clock=EventClock(1 => 810434015432544257, 99 => 810434016070479873) 1771059326.572 sable_services #x1B[2m2026-02-14T08:55:26.572419Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016091451394)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016070479873), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059326.573 sable_ircd #x1B[2m2026-02-14T08:55:26.572796Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016070479873)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016062091264), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059326 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434016070479873)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016062091264), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059326 }) }; event clock=EventClock(1 => 810434015432544257, 99 => 810434016062091264) my clock=EventClock(1 => 810434015910694914) 1771059326.573 sable_ircd #x1B[2m2026-02-14T08:55:26.572896Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016070479873)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016062091264), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059326 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434016062091264))] 1771059326.573 sable_ircd #x1B[2m2026-02-14T08:55:26.572955Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016070479873)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016062091264), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059326 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059326.608 sable_ircd #x1B[2m2026-02-14T08:55:26.608194Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059326, name: ServerName("My.Little.Services"), ts: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059326, name: ServerName("My.Little.Services"), ts: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 810434015432544257) my clock=EventClock(1 => 810434015910694914) 1771059326.608 sable_ircd #x1B[2m2026-02-14T08:55:26.608281Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059326, name: ServerName("My.Little.Services"), ts: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(810434016070479873)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016062091264), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059326 }) } 1771059326.608 sable_ircd #x1B[2m2026-02-14T08:55:26.608336Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059326, name: ServerName("My.Little.Services"), ts: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059326.609 sable_ircd #x1B[2m2026-02-14T08:55:26.608651Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m#x1B[1mapply_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mevent#x1B[0m#x1B[2m=#x1B[0mEvent { id: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059326, name: ServerName("My.Little.Services"), ts: 1771059326, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_server#x1B[0m#x1B[1m{#x1B[0m#x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mNewServer { server: ServerId(99) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Services 1771059326.609 sable_ircd #x1B[2m2026-02-14T08:55:26.608902Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] }) 1771059326.609 sable_ircd #x1B[2m2026-02-14T08:55:26.608938Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] } 1771059326.609 sable_ircd #x1B[2m2026-02-14T08:55:26.608976Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059326.609 sable_ircd #x1B[2m2026-02-14T08:55:26.608991Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434016062091264)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] } 1771059326.609 sable_ircd #x1B[2m2026-02-14T08:55:26.609021Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434016070479873)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059326.609 sable_ircd #x1B[2m2026-02-14T08:55:26.609040Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434016070479873)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] } 1771059326.614 sable_services #x1B[2m2026-02-14T08:55:26.614011Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: GetEvent([EventId(Snowflake(810434016062091264))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434016062091264))] 1771059326.614 sable_services #x1B[2m2026-02-14T08:55:26.614059Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: GetEvent([EventId(Snowflake(810434016062091264))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [] 1771059326.615 sable_ircd #x1B[2m2026-02-14T08:55:26.615042Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016091451394)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016070479873), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434016091451394)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016070479873), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 810434015432544257, 99 => 810434016070479873) my clock=EventClock(1 => 810434015910694914, 99 => 810434016070479873) 1771059326.616 sable_ircd #x1B[2m2026-02-14T08:55:26.615457Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016091451394)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016070479873), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434016091451394)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257, 99 => 810434016070479873), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36777 (127.0.0.1:36777) 1771059326.619 sable_ircd #x1B[2m2026-02-14T08:55:26.619178Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434016091451394)), timestamp: 1771059326, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] }) 1771059326.620 sable_ircd #x1B[2m2026-02-14T08:55:26.619218Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434016091451394)), timestamp: 1771059326, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] } 1771059326.620 sable_ircd #x1B[2m2026-02-14T08:55:26.619351Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434016091451394)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059326.620 sable_ircd #x1B[2m2026-02-14T08:55:26.619389Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434016091451394)), timestamp: 1771059326, change: EventComplete(EventComplete), users_to_notify: [] } 1771059326.627 S -> chkSvs: :My.Little.Server NOTICE * :*** Looking up your hostname 1771059326.627 S -> chkSvs: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059326.627 S -> chkSvs: :My.Little.Server 001 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs 1771059326.628 S -> chkSvs: :My.Little.Server 002 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1771059326.628 S -> chkSvs: :My.Little.Server 003 chkSvs :This server was created 2026-02-14 08:55:26.529988738 UTC 1771059326.628 S -> chkSvs: :My.Little.Server 004 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1771059326.628 S -> chkSvs: :My.Little.Server 005 chkSvs EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1771059326.628 S -> chkSvs: :My.Little.Server 422 chkSvs :MOTD File is missing 1771059326.628 S -> chkSvs: :chkSvs!chk@localhost MODE chkSvs + 1771059326.628 S -> chkSvs: :My.Little.Server NOTICE chkSvs :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1771059326.628 chkSvs -> S: CAP LS 302 1771059326.628 sable_ircd #x1B[2m2026-02-14T08:55:26.628012Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059326.628 sable_ircd #x1B[2m2026-02-14T08:55:26.628062Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP LS 302" 1771059326.628 sable_ircd #x1B[2m2026-02-14T08:55:26.628177Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1771059326.655 sable_ircd #x1B[2m2026-02-14T08:55:26.655051Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: BulkEvents([]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [] 1771059326.678 chkSvs -> S: PING synchronize354.743961046 1771059326.678 S -> chkSvs: :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1771059326.678 sable_ircd #x1B[2m2026-02-14T08:55:26.678204Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059326.678 sable_ircd #x1B[2m2026-02-14T08:55:26.678252Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize354.743961046" 1771059326.678 sable_ircd #x1B[2m2026-02-14T08:55:26.678354Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server PONG My.Little.Server :synchronize354.743961046 1771059326.679 S -> chkSvs: :My.Little.Server PONG My.Little.Server :synchronize354.743961046 1771059326.679 chkSvs -> S: QUIT 1771059326.679 chkSvs -> S: PING synchronize354.744986922 1771059326.679 sable_ircd #x1B[2m2026-02-14T08:55:26.679024Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059326.679 sable_ircd #x1B[2m2026-02-14T08:55:26.679066Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"QUIT" 1771059326.679 sable_ircd #x1B[2m2026-02-14T08:55:26.679121Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059326.679 sable_ircd #x1B[2m2026-02-14T08:55:26.679150Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize354.744986922" 1771059327.181 sable_ircd #x1B[2m2026-02-14T08:55:27.181350Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): ERROR :Client quit 1771059327.182 sable_ircd #x1B[2m2026-02-14T08:55:27.181502Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mCloseConnection(ConnectionId(ListenerId(0), 2)) 1771059327.182 sable_ircd #x1B[2m2026-02-14T08:55:27.181553Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::connection_collection#x1B[0m#x1B[2m:#x1B[0m Removing connection ConnectionId(ListenerId(0), 2) 1771059327.182 sable_ircd #x1B[2m2026-02-14T08:55:27.181698Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 810434015910694914, 99 => 810434016091451394) my clock=EventClock(1 => 810434015910694914, 99 => 810434016091451394) 1771059327.182 S -> chkSvs: ERROR :Client quit 1771059327.182 chkSvs: disconnects from server. 1771059327.182 1: connects to server. 1771059327.182 1 -> S: NICK jilles 1771059327.182 1 -> S: USER r e g :user 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.182097Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36777 (127.0.0.1:36777) 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.182113Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m Connection error #x1B[3mconnection#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.182199Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.182619Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 3), remote_addr: 127.0.0.1, tls_info: None } 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.182723Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.182801Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.182877Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server NOTICE * :*** Looking up your hostname 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.183016Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.183075Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"NICK jilles" 1771059327.183 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.183213Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 355, tv_nsec: 249109077 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(Nickname("jilles")), realname: OnceLock(), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.183292Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from async_handlers 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.183376Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059327.183 sable_ircd #x1B[2m2026-02-14T08:55:27.183435Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"USER r e g :user" 1771059327.184 sable_ircd #x1B[2m2026-02-14T08:55:27.183534Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 355, tv_nsec: 249109077 }, attach_user_id: OnceLock(), user: OnceLock(Username("r")), extra_user_params: OnceLock(("e", "g")), nick: OnceLock(Nickname("jilles")), realname: OnceLock(Realname("user")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059327.184 sable_ircd #x1B[2m2026-02-14T08:55:27.184175Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from auth_events 1771059327.184 sable_ircd #x1B[2m2026-02-14T08:55:27.184214Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m DNS lookup finished for ConnectionId(ListenerId(0), 3): 127.0.0.1/Some(Hostname("localhost")) 1771059327.184 sable_ircd #x1B[2m2026-02-14T08:55:27.184254Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059327.184 sable_ircd #x1B[2m2026-02-14T08:55:27.184292Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 355, tv_nsec: 249109077 }, attach_user_id: OnceLock(), user: OnceLock(Username("r")), extra_user_params: OnceLock(("e", "g")), nick: OnceLock(Nickname("jilles")), realname: OnceLock(Realname("user")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059327.184 sable_ircd #x1B[2m2026-02-14T08:55:27.184363Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mRegisterClient(ConnectionId(ListenerId(0), 3)) 1771059327.185 sable_ircd #x1B[2m2026-02-14T08:55:27.184439Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 810434018645381123, 99 => 810434016091451394) my clock=EventClock(1 => 810434018645381123, 99 => 810434016091451394) 1771059327.185 sable_ircd #x1B[2m2026-02-14T08:55:27.184863Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(810434015910694912)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(810434015910694913)), user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 } }), users_to_notify: [] }) 1771059327.185 sable_ircd #x1B[2m2026-02-14T08:55:27.184887Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36777 (127.0.0.1:36777) 1771059327.185 sable_ircd #x1B[2m2026-02-14T08:55:27.184916Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(810434015910694912)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(810434015910694913)), user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 } }), users_to_notify: [] } 1771059327.185 sable_ircd #x1B[2m2026-02-14T08:55:27.184993Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059327.185 sable_ircd #x1B[2m2026-02-14T08:55:27.185027Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, change: EventComplete(EventComplete), users_to_notify: [] } 1771059327.185 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059327.188 sable_ircd #x1B[2m2026-02-14T08:55:27.188371Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, clock: EventClock(1 => 810434018657964036, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434018657964035)), NewUserConnection { user: UserId(Snowflake(810434018657964034)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059327 })) }) }; event clock=EventClock(1 => 810434018657964036, 99 => 810434016091451394) my clock=EventClock(1 => 810434018657964036, 99 => 810434016091451394) 1771059327.189 sable_ircd #x1B[2m2026-02-14T08:55:27.188660Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, clock: EventClock(1 => 810434018657964036, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434018657964035)), NewUserConnection { user: UserId(Snowflake(810434018657964034)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059327 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36777 (127.0.0.1:36777) 1771059327.189 sable_ircd #x1B[2m2026-02-14T08:55:27.188854Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(810434015910694912)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] }) 1771059327.189 sable_ircd #x1B[2m2026-02-14T08:55:27.188892Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(810434015910694912)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] } 1771059327.189 sable_ircd #x1B[2m2026-02-14T08:55:27.188948Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059327.189 sable_ircd #x1B[2m2026-02-14T08:55:27.188980Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, change: EventComplete(EventComplete), users_to_notify: [] } 1771059327.190 sable_services #x1B[2m2026-02-14T08:55:27.189432Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 810434018645381123, 99 => 810434016091451394) my clock=EventClock(1 => 810434015432544257, 99 => 810434016091451394) 1771059327.190 sable_services #x1B[2m2026-02-14T08:55:27.189501Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434018645381123))] 1771059327.190 sable_services #x1B[2m2026-02-14T08:55:27.189843Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059327.191 sable_ircd #x1B[2m2026-02-14T08:55:27.191373Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(810434018657964034)), 1) }), users_to_notify: [] }) 1771059327.191 sable_ircd #x1B[2m2026-02-14T08:55:27.191418Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(810434018657964034)), 1) }), users_to_notify: [] } 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191474Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(810434018657964034)), 2), connection: UserConnectionId(Snowflake(810434018657964035)) }), users_to_notify: [] }) 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191511Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(810434018657964034)), 2), connection: UserConnectionId(Snowflake(810434018657964035)) }), users_to_notify: [] } 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191576Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 001 jilles :Welcome to the test Internet Relay Chat network, jilles 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191630Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 002 jilles :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191674Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 003 jilles :This server was created 2026-02-14 08:55:27.191659528 UTC 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191715Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 004 jilles My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191786Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 005 jilles EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191832Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 422 jilles :MOTD File is missing 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191873Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :jilles!r@localhost MODE jilles + 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191915Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server NOTICE jilles :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.191987Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059327.192 sable_ircd #x1B[2m2026-02-14T08:55:27.192018Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, change: EventComplete(EventComplete), users_to_notify: [] } 1771059327.192 S -> 1: :My.Little.Server 001 jilles :Welcome to the test Internet Relay Chat network, jilles 1771059327.193 S -> 1: :My.Little.Server 002 jilles :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1771059327.193 S -> 1: :My.Little.Server 003 jilles :This server was created 2026-02-14 08:55:27.191659528 UTC 1771059327.193 S -> 1: :My.Little.Server 004 jilles My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1771059327.193 S -> 1: :My.Little.Server 005 jilles EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1771059327.193 S -> 1: :My.Little.Server 422 jilles :MOTD File is missing 1771059327.193 S -> 1: :jilles!r@localhost MODE jilles + 1771059327.193 S -> 1: :My.Little.Server NOTICE jilles :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1771059327.226 sable_services #x1B[2m2026-02-14T08:55:27.226063Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 810434015910694914, 99 => 810434016091451394) my clock=EventClock(1 => 810434015432544257, 99 => 810434016091451394) 1771059327.226 sable_services #x1B[2m2026-02-14T08:55:27.226116Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434015910694914))] 1771059327.226 sable_services #x1B[2m2026-02-14T08:55:27.226388Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059327.231 sable_ircd #x1B[2m2026-02-14T08:55:27.230969Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: GetEvent([EventId(Snowflake(810434018645381123))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434018645381123))] 1771059327.231 sable_ircd #x1B[2m2026-02-14T08:55:27.231023Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: GetEvent([EventId(Snowflake(810434018645381123))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }] 1771059327.232 sable_services #x1B[2m2026-02-14T08:55:27.232070Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, clock: EventClock(1 => 810434018657964036, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434018657964035)), NewUserConnection { user: UserId(Snowflake(810434018657964034)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059327 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, clock: EventClock(1 => 810434018657964036, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434018657964035)), NewUserConnection { user: UserId(Snowflake(810434018657964034)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059327 })) }) }; event clock=EventClock(1 => 810434018657964036, 99 => 810434016091451394) my clock=EventClock(1 => 810434015432544257, 99 => 810434016091451394) 1771059327.232 sable_services #x1B[2m2026-02-14T08:55:27.232137Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, clock: EventClock(1 => 810434018657964036, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434018657964035)), NewUserConnection { user: UserId(Snowflake(810434018657964034)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059327 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434018657964036))] 1771059327.233 sable_services #x1B[2m2026-02-14T08:55:27.232516Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, clock: EventClock(1 => 810434018657964036, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434018657964035)), NewUserConnection { user: UserId(Snowflake(810434018657964034)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059327 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, clock: EventClock(1 => 810434018657964036, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434018657964035)), NewUserConnection { user: UserId(Snowflake(810434018657964034)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059327 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059327.268 sable_ircd #x1B[2m2026-02-14T08:55:27.268012Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: GetEvent([EventId(Snowflake(810434015910694914))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434015910694914))] 1771059327.268 sable_ircd #x1B[2m2026-02-14T08:55:27.268052Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: GetEvent([EventId(Snowflake(810434015910694914))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }] 1771059327.272 sable_services #x1B[2m2026-02-14T08:55:27.271997Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }] 1771059327.272 sable_services #x1B[2m2026-02-14T08:55:27.272064Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 810434015910694914, 99 => 810434016091451394) my clock=EventClock(1 => 810434015432544257, 99 => 810434016091451394) 1771059327.272 sable_services #x1B[2m2026-02-14T08:55:27.272120Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434015910694914))] 1771059327.273 sable_ircd #x1B[2m2026-02-14T08:55:27.272907Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: GetEvent([EventId(Snowflake(810434018657964036))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434018657964036))] 1771059327.273 sable_ircd #x1B[2m2026-02-14T08:55:27.272951Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: GetEvent([EventId(Snowflake(810434018657964036))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) }] 1771059327.293 1 -> S: PING synchronize355.359027669 1771059327.293 sable_ircd #x1B[2m2026-02-14T08:55:27.293273Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059327.293 sable_ircd #x1B[2m2026-02-14T08:55:27.293343Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize355.359027669" 1771059327.294 sable_ircd #x1B[2m2026-02-14T08:55:27.293509Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server PONG My.Little.Server :synchronize355.359027669 1771059327.294 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize355.359027669 1771059327.294 1 -> S: REGISTER * * sesame 1771059327.294 sable_ircd #x1B[2m2026-02-14T08:55:27.294242Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059327.294 sable_ircd #x1B[2m2026-02-14T08:55:27.294282Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"REGISTER * * sesame" 1771059327.295 sable_ircd #x1B[2m2026-02-14T08:55:27.294477Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Found target peer, sending message #x1B[3mtarget#x1B[0m#x1B[2m=#x1B[0mPeerConfig { name: ServerName("My.Little.Services"), address: "127.0.0.1:36777", fingerprint: "308dce7eca6f82319b202c654d85e74fbcf855b1" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(RegisterUser(Nickname("jilles"), "sesame")) } 1771059327.295 sable_ircd #x1B[2m2026-02-14T08:55:27.294692Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059326), content: TargetedMessage(TargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(RegisterUser(Nickname("jilles"), "sesame")) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36777 (127.0.0.1:36777) 1771059327.297 sable_ircd #x1B[2m2026-02-14T08:55:27.296459Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got send result #x1B[3msend_result#x1B[0m#x1B[2m=#x1B[0mOk(JoinHandle { id: Id(26) }) 1771059327.309 sable_services #x1B[2m2026-02-14T08:55:27.309053Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }] 1771059327.309 sable_services #x1B[2m2026-02-14T08:55:27.309123Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }; event clock=EventClock(1 => 810434015432544257) my clock=EventClock(1 => 810434015432544257, 99 => 810434016091451394) 1771059327.309 sable_services #x1B[2m2026-02-14T08:55:27.309196Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(810434018645381123)), timestamp: 1771059327, clock: EventClock(1 => 810434015910694914, 99 => 810434016091451394), target: UserConnection(UserConnectionId(Snowflake(810434015910694913))), details: UserDisconnect(UserDisconnect) } 1771059327.309 sable_services #x1B[2m2026-02-14T08:55:27.309237Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) } 1771059327.309 sable_services #x1B[2m2026-02-14T08:55:27.309280Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(810434018674741253)), timestamp: 1771059327, clock: EventClock(1 => 810434018657964036, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434018657964035)), NewUserConnection { user: UserId(Snowflake(810434018657964034)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059327 })) }) } 1771059327.314 sable_services #x1B[2m2026-02-14T08:55:27.313971Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(810434018657964036)), timestamp: 1771059327, clock: EventClock(1 => 810434018645381123, 99 => 810434016091451394), target: User(UserId(Snowflake(810434015910694912))), details: UserQuit(UserQuit { message: "Client Quit" }) }] 1771059327.337 sable_services #x1B[2m2026-02-14T08:55:27.337134Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got remote request #x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mServices(RegisterUser(Nickname("jilles"), "sesame")) 1771059327.337 sable_services #x1B[2m2026-02-14T08:55:27.337178Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got register request #x1B[3maccount_name#x1B[0m#x1B[2m=#x1B[0mNickname("jilles") 1771059327.342 sable_services #x1B[2m2026-02-14T08:55:27.341492Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server::command::user_commands#x1B[0m#x1B[2m:#x1B[0m Successfully created account #x1B[3mnew_account#x1B[0m#x1B[2m=#x1B[0mAccount { id: AccountId(Snowflake(810434019300093952)), name: Nickname("jilles"), authorised_fingerprints: [] } 1771059327.342 sable_services #x1B[2m2026-02-14T08:55:27.341686Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434019316871171)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: Account(AccountId(Snowflake(810434019300093952))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434019300093952)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 810434018674741253, 99 => 810434016091451394) my clock=EventClock(1 => 810434018674741253, 99 => 810434016091451394) 1771059327.342 sable_services #x1B[2m2026-02-14T08:55:27.342222Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434019316871171)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: Account(AccountId(Snowflake(810434019300093952))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434019300093952)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059327.383 sable_ircd #x1B[2m2026-02-14T08:55:27.382995Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got targeted message response #x1B[3mresponse#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: TargetedMessageResponse(Services(LogUserIn(AccountId(Snowflake(810434019300093952))))) } } 1771059327.383 sable_ircd #x1B[2m2026-02-14T08:55:27.383130Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: GetEvent([EventId(Snowflake(810434015910694914))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434015910694914))] 1771059327.383 sable_ircd #x1B[2m2026-02-14T08:55:27.383184Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: GetEvent([EventId(Snowflake(810434015910694914))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }] 1771059327.383 sable_ircd #x1B[2m2026-02-14T08:55:27.383405Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): REGISTER SUCCESS jilles :You have successfully registered 1771059327.384 sable_ircd #x1B[2m2026-02-14T08:55:27.383470Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from async_handlers 1771059327.384 sable_ircd #x1B[2m2026-02-14T08:55:27.383522Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mStateChange(User(UserId(Snowflake(810434018657964034))), UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434019300093952))) })) 1771059327.384 sable_ircd #x1B[2m2026-02-14T08:55:27.383685Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434019492630534)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434019300093952))) }) }; event clock=EventClock(1 => 810434018674741253, 99 => 810434016091451394) my clock=EventClock(1 => 810434018674741253, 99 => 810434016091451394) 1771059327.384 sable_ircd #x1B[2m2026-02-14T08:55:27.384003Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434019492630534)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434019300093952))) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36777 (127.0.0.1:36777) 1771059327.386 sable_ircd #x1B[2m2026-02-14T08:55:27.385708Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434019316871171)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: Account(AccountId(Snowflake(810434019300093952))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434019300093952)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434019316871171)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: Account(AccountId(Snowflake(810434019300093952))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434019300093952)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 810434018674741253, 99 => 810434016091451394) my clock=EventClock(1 => 810434019492630534, 99 => 810434016091451394) 1771059327.386 sable_ircd #x1B[2m2026-02-14T08:55:27.386268Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434019316871171)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: Account(AccountId(Snowflake(810434019300093952))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434019300093952)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434019316871171)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: Account(AccountId(Snowflake(810434019300093952))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434019300093952)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36777 (127.0.0.1:36777) 1771059327.390 sable_ircd #x1B[2m2026-02-14T08:55:27.390203Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434019492630534)), timestamp: 1771059327, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(810434018657964034)), 3), old_account: None, new_account: Some(AccountId(Snowflake(810434019300093952))) }), users_to_notify: [] }) 1771059327.390 sable_ircd #x1B[2m2026-02-14T08:55:27.390248Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434019492630534)), timestamp: 1771059327, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(810434018657964034)), 3), old_account: None, new_account: Some(AccountId(Snowflake(810434019300093952))) }), users_to_notify: [] } 1771059327.390 sable_ircd #x1B[2m2026-02-14T08:55:27.390320Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434019492630534)), timestamp: 1771059327, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059327.390 sable_ircd #x1B[2m2026-02-14T08:55:27.390365Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434019492630534)), timestamp: 1771059327, change: EventComplete(EventComplete), users_to_notify: [] } 1771059327.390 sable_ircd #x1B[2m2026-02-14T08:55:27.390432Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434019316871171)), timestamp: 1771059327, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059327.391 sable_ircd #x1B[2m2026-02-14T08:55:27.390478Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434019316871171)), timestamp: 1771059327, change: EventComplete(EventComplete), users_to_notify: [] } 1771059327.424 sable_services #x1B[2m2026-02-14T08:55:27.424064Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(810434015910694914)), timestamp: 1771059326, clock: EventClock(1 => 810434015432544257), target: User(UserId(Snowflake(810434015910694912))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434015910694913)), NewUserConnection { user: UserId(Snowflake(810434015910694912)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059326 })) }) }] 1771059327.427 sable_services #x1B[2m2026-02-14T08:55:27.427000Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434019492630534)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434019300093952))) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434019492630534)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434019300093952))) }) }; event clock=EventClock(1 => 810434018674741253, 99 => 810434016091451394) my clock=EventClock(1 => 810434018674741253, 99 => 810434019316871171) 1771059327.427 sable_services #x1B[2m2026-02-14T08:55:27.427252Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434019492630534)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434019300093952))) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434019492630534)), timestamp: 1771059327, clock: EventClock(1 => 810434018674741253, 99 => 810434016091451394), target: User(UserId(Snowflake(810434018657964034))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434019300093952))) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059327.494 1 -> S: PING synchronize355.560381728 1771059327.494 S -> 1: REGISTER SUCCESS jilles :You have successfully registered 1771059327.495 sable_ircd #x1B[2m2026-02-14T08:55:27.494627Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059327.495 sable_ircd #x1B[2m2026-02-14T08:55:27.494699Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize355.560381728" 1771059327.982 sable_history #x1B[2m2027-02-14T09:21:38.908108Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(942712181886230560)), timestamp: 1802596898, clock: EventClock(1 => 942705878115553313, 50 => 942711930227990559, 99 => 942705881844690949), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1802596898 }) }; event clock=EventClock(1 => 942705878115553313, 50 => 942711930227990559, 99 => 942705881844690949) my clock=EventClock(1 => 942705878115553313, 50 => 942711930227990559, 99 => 942705881844690949) 1771059327.982 sable_history #x1B[2m2027-02-14T09:21:38.910285Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059328.209 sable_history #x1B[2m2027-02-14T09:18:51.266339Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(942711478744719388)), timestamp: 1802596731, clock: EventClock(1 => 942705174378450945, 50 => 942711227086479387), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1802596731 }) }; event clock=EventClock(1 => 942705174378450945, 50 => 942711227086479387) my clock=EventClock(1 => 942705174378450945, 50 => 942711227086479387) 1771059328.209 sable_history #x1B[2m2027-02-14T09:18:51.268572Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059328.249 sable_ircd #x1B[2m2026-02-14T08:55:28.248557Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server PONG My.Little.Server :synchronize355.560381728 1771059328.249 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize355.560381728 1771059328.249 1 -> S: QUIT 1771059328.249 sable_ircd #x1B[2m2026-02-14T08:55:28.249420Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059328.250 sable_ircd #x1B[2m2026-02-14T08:55:28.249473Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"QUIT" 1771059328.349 1 -> S: PING synchronize356.415505472 1771059328.350 sable_ircd #x1B[2m2026-02-14T08:55:28.349957Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059328.350 sable_ircd #x1B[2m2026-02-14T08:55:28.350031Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize356.415505472" 1771059328.624 sable_history #x1B[2m2027-02-14T09:24:27.586556Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(942712889373040675)), timestamp: 1802597067, clock: EventClock(1 => 942705578784854038, 50 => 942712637714800674, 99 => 942705582086172676), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1802597067 }) }; event clock=EventClock(1 => 942705578784854038, 50 => 942712637714800674, 99 => 942705582086172676) my clock=EventClock(1 => 942705578784854038, 50 => 942712637714800674, 99 => 942705582086172676) 1771059328.624 sable_history #x1B[2m2027-02-14T09:24:27.588297Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059328.682 sable_history #x1B[2m2027-02-14T09:18:52.084117Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(942711482175660060)), timestamp: 1802596732, clock: EventClock(1 => 942705176232333313, 50 => 942711230517420059), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1802596732 }) }; event clock=EventClock(1 => 942705176232333313, 50 => 942711230517420059) my clock=EventClock(1 => 942705176232333313, 50 => 942711230517420059) 1771059328.682 sable_history #x1B[2m2027-02-14T09:18:52.085857Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059329.104 sable_ircd #x1B[2m2026-02-14T08:55:29.104091Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): ERROR :Client quit 1771059329.104 sable_ircd #x1B[2m2026-02-14T08:55:29.104251Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mCloseConnection(ConnectionId(ListenerId(0), 3)) 1771059329.104 sable_ircd #x1B[2m2026-02-14T08:55:29.104289Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::connection_collection#x1B[0m#x1B[2m:#x1B[0m Removing connection ConnectionId(ListenerId(0), 3) 1771059329.104 sable_ircd #x1B[2m2026-02-14T08:55:29.104411Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, clock: EventClock(1 => 810434019492630534, 99 => 810434019316871171), target: UserConnection(UserConnectionId(Snowflake(810434018657964035))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 810434019492630534, 99 => 810434019316871171) my clock=EventClock(1 => 810434019492630534, 99 => 810434019316871171) 1771059329.105 S -> 1: ERROR :Client quit 1771059329.105 sable_ircd #x1B[2m2026-02-14T08:55:29.104792Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, clock: EventClock(1 => 810434019492630534, 99 => 810434019316871171), target: UserConnection(UserConnectionId(Snowflake(810434018657964035))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36777 (127.0.0.1:36777) 1771059329.105 sable_ircd #x1B[2m2026-02-14T08:55:29.104843Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m Connection error #x1B[3mconnection#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed 1771059329.105 sable_ircd #x1B[2m2026-02-14T08:55:29.104936Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059329.107 sable_ircd #x1B[2m2026-02-14T08:55:29.106738Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, clock: EventClock(1 => 810434026711027719, 99 => 810434019316871171), target: User(UserId(Snowflake(810434018657964034))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 810434026711027719, 99 => 810434019316871171) my clock=EventClock(1 => 810434026711027719, 99 => 810434019316871171) 1771059329.107 sable_ircd #x1B[2m2026-02-14T08:55:29.107039Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, clock: EventClock(1 => 810434026711027719, 99 => 810434019316871171), target: User(UserId(Snowflake(810434018657964034))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:36777 (127.0.0.1:36777) 1771059329.107 sable_ircd #x1B[2m2026-02-14T08:55:29.107179Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(810434018657964034)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(810434018657964035)), user: UserId(Snowflake(810434018657964034)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059327 } }), users_to_notify: [] }) 1771059329.107 sable_ircd #x1B[2m2026-02-14T08:55:29.107221Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(810434018657964034)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(810434018657964035)), user: UserId(Snowflake(810434018657964034)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059327 } }), users_to_notify: [] } 1771059329.107 sable_ircd #x1B[2m2026-02-14T08:55:29.107280Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059329.107 sable_ircd #x1B[2m2026-02-14T08:55:29.107309Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, change: EventComplete(EventComplete), users_to_notify: [] } 1771059329.111 sable_ircd #x1B[2m2026-02-14T08:55:29.110723Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(810434018657964034)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] }) 1771059329.111 sable_ircd #x1B[2m2026-02-14T08:55:29.110793Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(810434018657964034)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] } 1771059329.111 sable_ircd #x1B[2m2026-02-14T08:55:29.110850Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059329.111 sable_ircd #x1B[2m2026-02-14T08:55:29.110881Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, change: EventComplete(EventComplete), users_to_notify: [] } 1771059329.112 sable_services #x1B[2m2026-02-14T08:55:29.111467Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, clock: EventClock(1 => 810434026711027719, 99 => 810434019316871171), target: User(UserId(Snowflake(810434018657964034))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, clock: EventClock(1 => 810434026711027719, 99 => 810434019316871171), target: User(UserId(Snowflake(810434018657964034))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 810434026711027719, 99 => 810434019316871171) my clock=EventClock(1 => 810434019492630534, 99 => 810434019316871171) 1771059329.112 sable_services #x1B[2m2026-02-14T08:55:29.111524Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, clock: EventClock(1 => 810434026711027719, 99 => 810434019316871171), target: User(UserId(Snowflake(810434018657964034))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434026711027719))] 1771059329.112 sable_services #x1B[2m2026-02-14T08:55:29.111828Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, clock: EventClock(1 => 810434026711027719, 99 => 810434019316871171), target: User(UserId(Snowflake(810434018657964034))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, clock: EventClock(1 => 810434026711027719, 99 => 810434019316871171), target: User(UserId(Snowflake(810434018657964034))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059329.148 sable_services #x1B[2m2026-02-14T08:55:29.148167Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, clock: EventClock(1 => 810434019492630534, 99 => 810434019316871171), target: UserConnection(UserConnectionId(Snowflake(810434018657964035))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, clock: EventClock(1 => 810434019492630534, 99 => 810434019316871171), target: UserConnection(UserConnectionId(Snowflake(810434018657964035))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 810434019492630534, 99 => 810434019316871171) my clock=EventClock(1 => 810434019492630534, 99 => 810434019316871171) 1771059329.148 sable_services #x1B[2m2026-02-14T08:55:29.148245Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, clock: EventClock(1 => 810434019492630534, 99 => 810434019316871171), target: UserConnection(UserConnectionId(Snowflake(810434018657964035))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(810434026719416328)), timestamp: 1771059329, clock: EventClock(1 => 810434026711027719, 99 => 810434019316871171), target: User(UserId(Snowflake(810434018657964034))), details: UserQuit(UserQuit { message: "Client Quit" }) } 1771059329.149 sable_services #x1B[2m2026-02-14T08:55:29.148596Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, clock: EventClock(1 => 810434019492630534, 99 => 810434019316871171), target: UserConnection(UserConnectionId(Snowflake(810434018657964035))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059326), content: NewEvent(Event { id: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, clock: EventClock(1 => 810434019492630534, 99 => 810434019316871171), target: UserConnection(UserConnectionId(Snowflake(810434018657964035))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:50795 (127.0.0.1:50795) 1771059329.152 sable_ircd #x1B[2m2026-02-14T08:55:29.151994Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: GetEvent([EventId(Snowflake(810434026711027719))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434026711027719))] 1771059329.152 sable_ircd #x1B[2m2026-02-14T08:55:29.152044Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059326), content: GetEvent([EventId(Snowflake(810434026711027719))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, clock: EventClock(1 => 810434019492630534, 99 => 810434019316871171), target: UserConnection(UserConnectionId(Snowflake(810434018657964035))), details: UserDisconnect(UserDisconnect) }] 1771059329.193 sable_services #x1B[2m2026-02-14T08:55:29.193118Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059326), content: BulkEvents([Event { id: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, clock: EventClock(1 => 810434019492630534, 99 => 810434019316871171), target: UserConnection(UserConnectionId(Snowflake(810434018657964035))), details: UserDisconnect(UserDisconnect) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(810434026711027719)), timestamp: 1771059329, clock: EventClock(1 => 810434019492630534, 99 => 810434019316871171), target: UserConnection(UserConnectionId(Snowflake(810434018657964035))), details: UserDisconnect(UserDisconnect) }] 1771059329.205 1 -> S: PING synchronize357.271029087 1771059329.205 1: connects to server. 1771059329.205 1 -> S: CAP LS 302 1771059329.206 sable_ircd #x1B[2m2026-02-14T08:55:29.205569Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 4), remote_addr: 127.0.0.1, tls_info: None } 1771059329.206 sable_ircd #x1B[2m2026-02-14T08:55:29.205723Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059329.206 sable_ircd #x1B[2m2026-02-14T08:55:29.205811Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1771059329.206 sable_ircd #x1B[2m2026-02-14T08:55:29.205861Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server NOTICE * :*** Looking up your hostname 1771059329.206 sable_ircd #x1B[2m2026-02-14T08:55:29.205977Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059329.206 sable_ircd #x1B[2m2026-02-14T08:55:29.206028Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP LS 302" 1771059329.206 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname 1771059329.206 sable_ircd #x1B[2m2026-02-14T08:55:29.206130Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1771059329.206 S -> 1: :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1771059329.207 1 -> S: CAP REQ :extended-monitor account-notify 1771059329.207 sable_ircd #x1B[2m2026-02-14T08:55:29.206774Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from auth_events 1771059329.207 sable_ircd #x1B[2m2026-02-14T08:55:29.206816Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m DNS lookup finished for ConnectionId(ListenerId(0), 4): 127.0.0.1/Some(Hostname("localhost")) 1771059329.207 sable_ircd #x1B[2m2026-02-14T08:55:29.206858Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059329.207 sable_ircd #x1B[2m2026-02-14T08:55:29.206892Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 357, tv_nsec: 272100844 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(), realname: OnceLock(), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059329.207 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059329.207 sable_ircd #x1B[2m2026-02-14T08:55:29.207132Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059329.207 sable_ircd #x1B[2m2026-02-14T08:55:29.207188Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP REQ :extended-monitor account-notify" 1771059329.207 sable_ircd #x1B[2m2026-02-14T08:55:29.207296Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server CAP * NAK :extended-monitor account-notify 1771059329.212 sable_history #x1B[2m2027-02-14T09:18:53.153120Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(942711486659371036)), timestamp: 1802596733, clock: EventClock(1 => 942705178258182145, 50 => 942711235005325339), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1802596733 }) }; event clock=EventClock(1 => 942705178258182145, 50 => 942711235005325339) my clock=EventClock(1 => 942705178258182145, 50 => 942711235005325339) 1771059329.213 sable_history #x1B[2m2027-02-14T09:18:53.154570Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059329.221 sable_history #x1B[2m2027-02-14T09:27:28.637763Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(942713648755974182)), timestamp: 1802597248, clock: EventClock(1 => 942705783517220887, 50 => 942713397110317093, 99 => 942705783609896965), target: Server(ServerId(50)), details: ServerPing(ServerPing { ts: 1802597248 }) }; event clock=EventClock(1 => 942705783517220887, 50 => 942713397110317093, 99 => 942705783609896965) my clock=EventClock(1 => 942705783517220887, 50 => 942713397110317093, 99 => 942705783609896965) 1771059329.221 sable_history #x1B[2m2027-02-14T09:27:28.639049Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059329.248 S -> 1: :My.Little.Server CAP * NAK :extended-monitor account-notify 1771059329.252 sable_ircd #x1B[2m2026-02-14T08:55:29.251312Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error in outbound network sync connection: I/O error: unexpected end of file 1771059329.252 sable_ircd #x1B[2m2026-02-14T08:55:29.251526Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1mhandle_connection#x1B[0m#x1B[1m{#x1B[0m#x1B[3mconn#x1B[0m#x1B[2m=#x1B[0mPollEvented { io: Some(TcpStream { addr: 127.0.0.1:50795, peer: 127.0.0.1:52391, fd: 19 }) } #x1B[3mmessage_sender#x1B[0m#x1B[2m=#x1B[0mUnboundedSender { chan: Tx { inner: Chan { tx: Tx { block_tail: 0x56511a668b10, tail_position: 0 }, semaphore: Semaphore(0), rx_waker: AtomicWaker, tx_count: 2, rx_fields: "..." } } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error handling message: I/O error: unexpected end of file 1771059329.306 1: disconnects from server.